[2017.09.14 14:32:07] [0000m.00s.030ms] [1] FlowInstallVersion: WARNING: Could not get flow install version file [InstallVersion.txt] creation time
[2017.09.14 14:32:07] [0000m.00s.035ms] [0] WELCOME
[2017.09.14 14:32:07] [0000m.00s.035ms] [1] iCDB Server ver.1.094, vt40T8RtWJOG2cE2Nlvc5A, build:535943 created 2013-01-08 09:09:26, sources stamp:vt40T8RtWJOG2cE2Nlvc5A
[2017.09.14 14:32:07] [0000m.00s.035ms] [1] Copyright 2013 Mentor Graphics Corporation. All Rights Reserved.
[2017.09.14 14:32:07] [0000m.00s.035ms] [1] Base flow version [7.9.4]
[2017.09.14 14:32:07] [0000m.00s.035ms] [1] GBS Install Id [26418], Flow Id [EE7.9.4], Full Flow Name [Expedition Enterprise Flow], Exact Access Date [Jun 1 2012]
[2017.09.14 14:32:07] [0000m.00s.035ms] [1] UPDATE Install Info, GBS Install Id [29736], UPDATE Name [Expedition Enterprise Flow - Update 10 for EE7.9.4]
[2017.09.14 14:32:07] [0000m.00s.035ms] [1] Machine [W2130]  User [levk]  PID [8068]
[2017.09.14 14:32:07] [0000m.00s.035ms] [1] Operating system [Microsoft Windows 7 Business Edition, 64-bit Service Pack 1 (build 7601)]
[2017.09.14 14:32:07] [0000m.00s.035ms] [1] Current working dir [Z:\Projects\Boards\VB\COMPULAB\CM-188_FC-MPCI_Rev1.0\PCB]
[2017.09.14 14:32:07] [0000m.00s.036ms] [1] PATH [C:\MentorGraphics\7.9.4EE\SDD_HOME\common\win32\bin;C:\MentorGraphics\7.9.4EE\SDD_HOME\common\win32\lib;C:\MentorGraphics\7.9.4EE\SDD_HOME\xe\win32\lib;C:\MentorGraphics\7.9.4EE\SDD_HOME\common\win32\bin;C:\MentorGraphics\7.9.4EE\SDD_HOME\common\win32\lib;C:\MentorGraphics\7.9.4EE\SDD_HOME\wg\win32\lib;C:\MentorGraphics\7.9.4EE\SDD_HOME\common\win32\lib;C:\MentorGraphics\7.9.4EE\SDD_HOME\common\win32\bin;C:\MentorGraphics\7.9.4EE\SDD_HOME\common\win32\bin;C:\MentorGraphics\7.9.4EE\SDD_HOME\common\win32\lib;C:\MentorGraphics\7.9.4EE\MGC_HOME.ixn\bin;C:\MentorGraphics\7.9.4EE\MGC_HOME.ixn\lib;C:\Program Files\Common Files\Microsoft Shared\Microsoft Online Services;C:\Program Files (x86)\Common Files\Microsoft Shared\Microsoft Online Services;C:\ProgramData\Oracle\Java\javapath;C:\MentorGraphics\Allegro Export ODB++\nv\bin;C:\Windows\system32;C:\Windows;C:\Windows\System32\Wbem;C:\Windows\System32\WindowsPowerShell\v1.0\;C:\Program Files (x86)\Common Files\Acronis\SnapAPI\;C:\Program Files (x86)\Altium Designer S09 Viewer\System;C:\Program Files\Aladdin\eToken\PKIClient\x32;C:\Program Files\Aladdin\eToken\PKIClient\x64;C:\Program Files\TortoiseSVN\bin;C:\Program Files (x86)\Skype\Phone\;C:\Cadence\SPB_16.6\openaccess\bin\win32\opt;C:\Cadence\SPB_16.6\tools\capture;C:\Cadence\SPB_16.6\tools\pspice;C:\Cadence\SPB_16.6\tools\specctra\bin;C:\Cadence\SPB_16.6\tools\fet\bin;C:\Cadence\SPB_16.6\tools\libutil\bin;C:\Cadence\SPB_16.6\tools\bin;C:\Cadence\SPB_16.6\tools\pcb\bin;;C:\MentorGraphics\7.9.4EE\SDD_HOME\common\win32\lib;C:\MentorGraphics\7.9.5EE\SDD_HOME\common\win32\lib;C:\MentorGraphics\1.0CCASM\SDD_HOME\common\win32\lib]
[2017.09.14 14:32:07] [0000m.00s.036ms] [1] SDD_HOME [C:\MentorGraphics\7.9.4EE\SDD_HOME] on file system [NTFS]
[2017.09.14 14:32:07] [0000m.00s.036ms] [1] SDD_PLATFORM [win32]
[2017.09.14 14:32:07] [0000m.00s.036ms] [1] WDIR [C:\WDIR\7.9.4EE;C:\MentorGraphics\7.9.4EE\SDD_HOME\standard]
[2017.09.14 14:32:07] [0000m.00s.036ms] [1] Local iCDB working dir [C:\WDIR\7.9.4EE\iCDB] on file system [NTFS]
[2017.09.14 14:32:07] [0000m.00s.037ms] [1] S: Config file name [ServerWG.cfg]
[2017.09.14 14:32:07] [0000m.00s.037ms] [2] S: Loading config files
[2017.09.14 14:32:07] [0000m.00s.041ms] [1] Config file [Z:\Projects\Boards\VB\COMPULAB\CM-188_FC-MPCI_Rev1.0\PCB\keyin.icdb\cdbsvr\ServerWG.cfg] not found
[2017.09.14 14:32:07] [0000m.00s.041ms] [1] Config: Non-default value [System.SingleUserMode] = [no]
[2017.09.14 14:32:07] [0000m.00s.043ms] [3] S: Config loaded
[2017.09.14 14:32:07] [0000m.00s.066ms] [0] S: Project path [Z:\Projects\Boards\VB\COMPULAB\CM-188_FC-MPCI_Rev1.0\PCB\keyin.icdb] on file system [NTFS]
[2017.09.14 14:32:07] [0000m.00s.066ms] [0] S: Project global path [\\berg\data\Projects\Boards\VB\COMPULAB\CM-188_FC-MPCI_Rev1.0\PCB\keyin.icdb] on file system [NTFS]
[2017.09.14 14:32:07] [0000m.00s.067ms] [0] S: Project file (.prj) path [\\berg\data\Projects\Boards\VB\COMPULAB\CM-188_FC-MPCI_Rev1.0\PCB\FC-MPCI-1R0.prj] on file system [UNKNOWN]
[2017.09.14 14:32:07] [0000m.00s.067ms] [0] S: Project file (.prj) local path [Z:\Projects\Boards\VB\COMPULAB\CM-188_FC-MPCI_Rev1.0\PCB\FC-MPCI-1R0.prj] on file system [NTFS]
[2017.09.14 14:32:07] [0000m.00s.072ms] [1] S: WARNING: Database auto-repair support DISABLED
[2017.09.14 14:32:07] [0000m.00s.072ms] [0] S: Multi-user mode (config)
[2017.09.14 14:32:07] [0000m.00s.072ms] [0] S: Event polling mode autodetection
[2017.09.14 14:32:07] [0000m.00s.078ms] [0] S: Started up automatically by iCDB Client: W2130:levk:3516
[2017.09.14 14:32:07] [0000m.00s.078ms] [0] S: Auto close mode
[2017.09.14 14:32:07] [0000m.00s.078ms] [0] S: Database ID [9158-59ed-635f-dd63-6b0f-fa9b-b0ff-a950-beae-8c0c-2cd9-86c5]
[2017.09.14 14:32:07] [0000m.00s.078ms] [0] PathTranslator: BEGIN dumping
[2017.09.14 14:32:07] [0000m.00s.078ms] [0] PathTranslator: Local -> global path prefix list
[2017.09.14 14:32:07] [0000m.00s.078ms] [0] PathTranslator: [V:\] -> [\\SQLSERVER\Software\]
[2017.09.14 14:32:07] [0000m.00s.078ms] [0] PathTranslator: [W:\] -> [\\SQLSERVER\AppDistribution\]
[2017.09.14 14:32:07] [0000m.00s.078ms] [0] PathTranslator: [X:\] -> [\\BERG\system\Ufolders\levk\]
[2017.09.14 14:32:07] [0000m.00s.078ms] [0] PathTranslator: [Y:\] -> [\\berg\programs\]
[2017.09.14 14:32:07] [0000m.00s.078ms] [0] PathTranslator: [Z:\] -> [\\berg\data\]
[2017.09.14 14:32:07] [0000m.00s.078ms] [0] PathTranslator: Global -> local path prefix list
[2017.09.14 14:32:07] [0000m.00s.078ms] [0] PathTranslator: [\\SQLSERVER\AppDistribution\] -> [W:\]
[2017.09.14 14:32:07] [0000m.00s.078ms] [0] PathTranslator: [\\BERG\system\Ufolders\levk\] -> [X:\]
[2017.09.14 14:32:07] [0000m.00s.078ms] [0] PathTranslator: [\\SQLSERVER\Software\] -> [V:\]
[2017.09.14 14:32:07] [0000m.00s.078ms] [0] PathTranslator: [\\berg\programs\] -> [Y:\]
[2017.09.14 14:32:07] [0000m.00s.078ms] [0] PathTranslator: [\\berg\data\] -> [Z:\]
[2017.09.14 14:32:07] [0000m.00s.078ms] [0] PathTranslator: END dumping
[2017.09.14 14:32:07] [0000m.00s.078ms] [0] S: START
[2017.09.14 14:32:07] [0000m.00s.080ms] [0] S: Server startup lock file created
[2017.09.14 14:32:07] [0000m.00s.082ms] [0] S: Listening for client connections on port [10000]
[2017.09.14 14:32:07] [0000m.00s.083ms] [0] S: Server network ID [W2130:192.168.10.119,W2130,W2130.VG.LOCAL:10000]
[2017.09.14 14:32:07] [0000m.00s.083ms] [0] S: Server GUID [9ce4-5ddd-d69b-c306-7b86-ab9c-b86a-b950-310f-8c0c-2cd9-86c5]
[2017.09.14 14:32:07] [0000m.00s.083ms] [0] S: Project GUID [9158-59ed-635f-dd63-6b0f-fa9b-b0ff-a950-beae-8c0c-2cd9-86c5]
[2017.09.14 14:32:07] [0000m.00s.095ms] [0] S: Wrote data to server address file
[2017.09.14 14:32:07] [0000m.00s.097ms] [0] S: Server startup lock file removed
[2017.09.14 14:32:07] [0000m.00s.098ms] [1] ProjectBackupManager: Setting project file (.prj) path: [Z:\Projects\Boards\VB\COMPULAB\CM-188_FC-MPCI_Rev1.0\PCB\FC-MPCI-1R0.prj]
[2017.09.14 14:32:07] [0000m.00s.199ms] [0] S: Plugins loaded: [PIM] [ENetGen70] 
[2017.09.14 14:32:07] [0000m.00s.199ms] [0] S: Local disc access time: 43 us
[2017.09.14 14:32:07] [0000m.00s.199ms] [0] S: File server access time: 223 us
[2017.09.14 14:32:07] [0000m.00s.199ms] [0] S: Server will not release in-memory storage after read (memory usage overhead) - for better performance
[2017.09.14 14:32:07] [0000m.00s.207ms] [0] Single file [Z:\Projects\Boards\VB\COMPULAB\CM-188_FC-MPCI_Rev1.0\PCB\keyin.icdb\icdb.dat] size [73776 (72.0 kB)]
[2017.09.14 14:32:07] [0000m.00s.208ms] [1] Single file [Z:\Projects\Boards\VB\COMPULAB\CM-188_FC-MPCI_Rev1.0\PCB\keyin.icdb\icdb.dat] opened in read-write mode
[2017.09.14 14:32:07] [0000m.00s.212ms] [0] SingleFile: [0000-0000-0000-0000] SFI header version check [1009] [1009]
[2017.09.14 14:32:07] [0000m.00s.212ms] [0] Database GUARD OK: [73776]
[2017.09.14 14:32:07] [0000m.00s.217ms] [0] SingleFile: [0000-0000-0000-0000] SFI header version check [1009] [1009]
[2017.09.14 14:32:07] [0000m.00s.217ms] [0] Database GUARD OK: [73776]
[2017.09.14 14:32:07] [0000m.00s.217ms] [0] SingleFile: [ab06-e095-5029-1e89] SFI version check [1009] [1009]
[2017.09.14 14:32:07] [0000m.00s.219ms] [0] SingleFile: [ab06-e095-5029-1e89] Database SFI header has been read in [1ms 684us]
[2017.09.14 14:32:07] [0000m.00s.221ms] [0] iCDB Server PI: Server & plugin session [124]
[2017.09.14 14:32:07] [0000m.00s.221ms] [0] iCDB Server PI: Opening database [Z:\Projects\Boards\VB\COMPULAB\CM-188_FC-MPCI_Rev1.0\PCB\keyin.icdb]
[2017.09.14 14:32:07] [0000m.00s.222ms] [0] iCDB Server PI: Database [Z:\Projects\Boards\VB\COMPULAB\CM-188_FC-MPCI_Rev1.0\PCB\keyin.icdb] opened
[2017.09.14 14:32:07] [0000m.00s.250ms] [0] iCDB Database: Checking existence of constraint definitions
[2017.09.14 14:32:07] [0000m.00s.250ms] [0] iCDB Database: Checking constraint definitions version
[2017.09.14 14:32:07] [0000m.00s.301ms] [1] iCDB Database: Constraint definitions version [ce_ee.cns=7.10.24;user.cns=bd5a-97c5-3627-126d-4128-4d69-1284-d650-358d-8c0c-2c99-86c5] up to date
[2017.09.14 14:32:07] [0000m.00s.321ms] [0] iCDB Server PI: Initializing plugins
[2017.09.14 14:32:07] [0000m.00s.322ms] [0] iCDB Server PI: Plugins initialized
[2017.09.14 14:32:07] [0000m.00s.322ms] [0] S: iCDB database loaded
[2017.09.14 14:32:07] [0000m.00s.323ms] [0] S: Starting threads
[2017.09.14 14:32:07] [0000m.00s.323ms] [0] BT: Listening for broadcast messages on port [10000]
[2017.09.14 14:32:07] [0000m.00s.323ms] [1] CMT: There are no clients - waiting [10 seconds]
[2017.09.14 14:32:07] [0000m.00s.324ms] [0] IT: Session [125] for host [W2130] user [levk] with process ID [3516] created
[2017.09.14 14:32:07] [0000m.00s.326ms] [1] SC[125]: Registered sub-client [Expedition PCB_20021281] with ID [1] for session ID [125] host [W2130] user [levk] process ID [3516]
[2017.09.14 14:32:07] [0000m.00s.357ms] [1] SC[125]: Registered sub-client [EEVM_EEVM_155244924] with ID [2] for session ID [125] host [W2130] user [levk] process ID [3516]
[2017.09.14 14:32:07] [0000m.00s.358ms] [1] SC[125]: UnRegistered sub-client [EEVM_EEVM_155244924] with ID [2] for session ID [125] host [W2130] user [levk] process ID [3516]
[2017.09.14 14:32:07] [0000m.00s.424ms] [1] CMT: There are [1] clients now: 125
[2017.09.14 14:32:09] [0000m.02s.250ms] [1] SC[125]: Registered sub-client [JobPrefsDBPI_20023593] with ID [3] for session ID [125] host [W2130] user [levk] process ID [3516]
[2017.09.14 14:32:09] [0000m.02s.259ms] [1] SC[125]: UnRegistered sub-client [JobPrefsDBPI_20023593] with ID [3] for session ID [125] host [W2130] user [levk] process ID [3516]
[2017.09.14 14:32:11] [0000m.04s.709ms] [1] SC[125]: Registered sub-client [ExpeditionCrossProber] with ID [4] for session ID [125] host [W2130] user [levk] process ID [3516]
[2017.09.14 14:32:13] [0000m.06s.572ms] [1] SC[125]: Registered sub-client [JobPrefsDBPI_20027921] with ID [5] for session ID [125] host [W2130] user [levk] process ID [3516]
[2017.09.14 14:32:13] [0000m.06s.587ms] [1] SC[125]: UnRegistered sub-client [JobPrefsDBPI_20027921] with ID [5] for session ID [125] host [W2130] user [levk] process ID [3516]
[2017.09.14 14:32:27] [0000m.20s.849ms] [0] DFAMT: File server access time: 264 us
[2017.09.14 14:52:01] [0019m.53s.964ms] [1] SC[125]: Registered sub-client [JobPrefsDBPI_21215390] with ID [6] for session ID [125] host [W2130] user [levk] process ID [3516]
[2017.09.14 14:52:01] [0019m.54s.121ms] [1] SC[125]: UnRegistered sub-client [JobPrefsDBPI_21215390] with ID [6] for session ID [125] host [W2130] user [levk] process ID [3516]
[2017.09.14 14:52:01] [0019m.54s.155ms] [1] SC[125]: Registered sub-client [JobPrefsDBPI_21215578] with ID [7] for session ID [125] host [W2130] user [levk] process ID [3516]
[2017.09.14 14:52:01] [0019m.54s.168ms] [1] SC[125]: UnRegistered sub-client [JobPrefsDBPI_21215578] with ID [7] for session ID [125] host [W2130] user [levk] process ID [3516]
[2017.09.14 14:52:01] [0019m.54s.180ms] [1] SC[125]: Registered sub-client [JobPrefsDBPI_21215609] with ID [8] for session ID [125] host [W2130] user [levk] process ID [3516]
[2017.09.14 14:52:01] [0019m.54s.187ms] [1] SC[125]: UnRegistered sub-client [JobPrefsDBPI_21215609] with ID [8] for session ID [125] host [W2130] user [levk] process ID [3516]
[2017.09.14 14:52:02] [0019m.55s.495ms] [1] SC[125]: UnRegistered sub-client [ExpeditionCrossProber] with ID [4] for session ID [125] host [W2130] user [levk] process ID [3516]
[2017.09.14 14:52:02] [0019m.55s.670ms] [1] SC[125]: UnRegistered sub-client [Expedition PCB_20021281] with ID [1] for session ID [125] host [W2130] user [levk] process ID [3516]
[2017.09.14 14:52:02] [0019m.55s.708ms] [1] CMT: Removed client [125]
[2017.09.14 14:52:02] [0019m.55s.709ms] [1] CMT: There are no clients left - waiting [1 seconds]
[2017.09.14 14:52:03] [0019m.56s.270ms] [0] IT: Session [126] for host [W2130] user [levk] with process ID [3516] created
[2017.09.14 14:52:03] [0019m.56s.271ms] [1] SC[126]: Registered sub-client [Expedition PCB_21217656] with ID [1] for session ID [126] host [W2130] user [levk] process ID [3516]
[2017.09.14 14:52:06] [0019m.58s.893ms] [1] SC[126]: Registered sub-client [ExpeditionCrossProber] with ID [2] for session ID [126] host [W2130] user [levk] process ID [3516]
[2017.09.14 14:52:07] [0020m.00s.592ms] [1] SC[126]: Registered sub-client [JobPrefsDBPI_21222015] with ID [3] for session ID [126] host [W2130] user [levk] process ID [3516]
[2017.09.14 14:52:07] [0020m.00s.602ms] [1] SC[126]: UnRegistered sub-client [JobPrefsDBPI_21222015] with ID [3] for session ID [126] host [W2130] user [levk] process ID [3516]
[2017.09.14 14:57:51] [0025m.44s.066ms] [1] SC[126]: Registered sub-client [JobPrefsDBPI_21565515] with ID [4] for session ID [126] host [W2130] user [levk] process ID [3516]
[2017.09.14 14:57:51] [0025m.44s.078ms] [1] SC[126]: UnRegistered sub-client [JobPrefsDBPI_21565515] with ID [4] for session ID [126] host [W2130] user [levk] process ID [3516]
[2017.09.14 14:59:43] [0027m.36s.439ms] [1] SC[126]: Registered sub-client [JobPrefsDBPI_21677890] with ID [5] for session ID [126] host [W2130] user [levk] process ID [3516]
[2017.09.14 14:59:43] [0027m.36s.453ms] [1] SC[126]: UnRegistered sub-client [JobPrefsDBPI_21677890] with ID [5] for session ID [126] host [W2130] user [levk] process ID [3516]
[2017.09.14 15:00:07] [0028m.00s.528ms] [0] IT: Session [127] for host [W2130] user [levk] with process ID [7512] created
[2017.09.14 15:00:07] [0028m.00s.530ms] [1] SC[127]: Registered sub-client [levk (BE CES)] with ID [1] for session ID [127] host [W2130] user [levk] process ID [7512]
[2017.09.14 15:00:07] [0028m.00s.589ms] [1] CMT: There are [2] clients now: 126, 127
[2017.09.14 15:00:50] [0028m.43s.257ms] [1] SC[127]: UnRegistered sub-client [levk (BE CES)] with ID [1] for session ID [127] host [W2130] user [levk] process ID [7512]
[2017.09.14 15:00:50] [0028m.43s.285ms] [1] CMT: Removed client [127]
[2017.09.14 15:00:50] [0028m.43s.285ms] [1] CMT: There are [1] clients now: 126
[2017.09.14 15:10:02] [0037m.55s.711ms] [1] SC[126]: Registered sub-client [EEVM_EEVM_1632336] with ID [6] for session ID [126] host [W2130] user [levk] process ID [3516]
[2017.09.14 15:10:02] [0037m.55s.711ms] [1] SC[126]: UnRegistered sub-client [EEVM_EEVM_1632336] with ID [6] for session ID [126] host [W2130] user [levk] process ID [3516]
[2017.09.14 15:10:03] [0037m.55s.891ms] [0] IT: Session [128] for host [W2130] user [levk] with process ID [7384] created
[2017.09.14 15:10:03] [0037m.55s.892ms] [1] SC[128]: Registered sub-client [Expedition_22297218] with ID [1] for session ID [128] host [W2130] user [levk] process ID [7384]
[2017.09.14 15:10:03] [0037m.55s.938ms] [1] CMT: There are [2] clients now: 126, 128
[2017.09.14 15:10:03] [0037m.56s.010ms] [1] SC[128]: UnRegistered sub-client [Expedition_22297218] with ID [1] for session ID [128] host [W2130] user [levk] process ID [7384]
[2017.09.14 15:10:03] [0037m.56s.039ms] [1] CMT: Removed client [128]
[2017.09.14 15:10:03] [0037m.56s.039ms] [1] CMT: There are [1] clients now: 126
[2017.09.14 15:10:12] [0038m.04s.969ms] [1] SC[126]: Registered sub-client [JobPrefsDBPI_22306343] with ID [7] for session ID [126] host [W2130] user [levk] process ID [3516]
[2017.09.14 15:10:28] [0038m.21s.859ms] [0] IT: Session [129] for host [W2130] user [levk] with process ID [9132] created
[2017.09.14 15:10:28] [0038m.21s.859ms] [1] SC[129]: Registered sub-client [JobPrefsDBPI_22323187] with ID [1] for session ID [129] host [W2130] user [levk] process ID [9132]
[2017.09.14 15:10:29] [0038m.21s.890ms] [1] CMT: There are [2] clients now: 126, 129
[2017.09.14 15:10:29] [0038m.22s.044ms] [1] SC[129]: Registered sub-client [JobPrefsDBPI_22323421] with ID [2] for session ID [129] host [W2130] user [levk] process ID [9132]
[2017.09.14 15:10:29] [0038m.22s.068ms] [1] SC[129]: UnRegistered sub-client [JobPrefsDBPI_22323187] with ID [1] for session ID [129] host [W2130] user [levk] process ID [9132]
[2017.09.14 15:10:30] [0038m.23s.303ms] [1] SC[129]: UnRegistered sub-client [JobPrefsDBPI_22323421] with ID [2] for session ID [129] host [W2130] user [levk] process ID [9132]
[2017.09.14 15:10:30] [0038m.23s.400ms] [1] CMT: Removed client [129]
[2017.09.14 15:10:30] [0038m.23s.400ms] [1] CMT: There are [1] clients now: 126
[2017.09.14 15:10:30] [0038m.23s.695ms] [1] SC[126]: UnRegistered sub-client [JobPrefsDBPI_22306343] with ID [7] for session ID [126] host [W2130] user [levk] process ID [3516]
[2017.09.14 15:10:57] [0038m.50s.341ms] [1] SC[126]: UnRegistered sub-client [ExpeditionCrossProber] with ID [2] for session ID [126] host [W2130] user [levk] process ID [3516]
[2017.09.14 15:10:57] [0038m.50s.713ms] [1] SC[126]: UnRegistered sub-client [Expedition PCB_21217656] with ID [1] for session ID [126] host [W2130] user [levk] process ID [3516]
[2017.09.14 15:10:57] [0038m.50s.794ms] [1] CMT: Removed client [126]
[2017.09.14 15:10:57] [0038m.50s.794ms] [1] CMT: There are no clients left - waiting [1 seconds]
[2017.09.14 15:10:59] [0038m.51s.909ms] [0] CMT: Starting server auto-close
[2017.09.14 15:10:59] [0038m.51s.928ms] [0] S: Closing server
[2017.09.14 15:10:59] [0038m.52s.020ms] [0] iCDB Server PI: Closing plugins
[2017.09.14 15:10:59] [0038m.52s.020ms] [0] iCDB Server PI: Plugins closed
[2017.09.14 15:10:59] [0038m.52s.020ms] [0] iCDB Server PI: Unloading database
[2017.09.14 15:10:59] [0038m.52s.022ms] [0] iCDB Server PI: Saving storage
[2017.09.14 15:10:59] [0038m.52s.022ms] [0] iCDB Server PI: Closing database
[2017.09.14 15:10:59] [0038m.52s.027ms] [1] iCDB Server PI: Stats: Database defragmentation: max[2] avg[1] holes[85%] data[88%]
[2017.09.14 15:10:59] [0038m.52s.027ms] [1] iCDB Server PI: Stats: Database file size [133984 (130.8 kB)]
[2017.09.14 15:10:59] [0038m.52s.029ms] [1] iCDB Server PI: WARNING: Database needs defragmentation!
[2017.09.14 15:10:59] [0038m.52s.036ms] [0] iCDB Server PI: Database closed
[2017.09.14 15:10:59] [0038m.52s.036ms] [0] iCDB Server PI: Defragmenting database
[2017.09.14 15:10:59] [0038m.52s.037ms] [0] iCDB Server PI: Creating database [Z:\Projects\Boards\VB\COMPULAB\CM-188_FC-MPCI_Rev1.0\PCB\keyin.icdb] backup to file [Z:\Projects\Boards\VB\COMPULAB\CM-188_FC-MPCI_Rev1.0\PCB\keyin.icdb\cdbback\2017-09-14 15.10.59-8068-d.zip]
[2017.09.14 15:10:59] [0038m.52s.279ms] [0] iCDB Server PI: Database backup: DONE
[2017.09.14 15:10:59] [0038m.52s.291ms] [0] SingleFile: [0000-0000-0000-0000] SFI header version check [1009] [1009]
[2017.09.14 15:10:59] [0038m.52s.291ms] [0] Database GUARD OK: [133984]
[2017.09.14 15:10:59] [0038m.52s.291ms] [0] SingleFile: [ab06-e095-5029-1e89] SFI version check [1009] [1009]
[2017.09.14 15:10:59] [0038m.52s.293ms] [0] SingleFile: [ab06-e095-5029-1e89] Database SFI header has been read in [2ms 497us]
[2017.09.14 15:10:59] [0038m.52s.315ms] [1] iCDB Server PI: Stats: Database file sizes: before[133984 (130.8 kB)], after[73840 (72.1 kB)], gain[60144 (58.7 kB)]
[2017.09.14 15:10:59] [0038m.52s.324ms] [0] Single file [Z:\Projects\Boards\VB\COMPULAB\CM-188_FC-MPCI_Rev1.0\PCB\keyin.icdb\icdb.dat] closed with size [73840 (72.1 kB)]
[2017.09.14 15:10:59] [0038m.52s.327ms] [0] iCDB Server PI: Database defragmentation completed successfully
[2017.09.14 15:10:59] [0038m.52s.327ms] [1] BackupTransaction: Removing database backup [Z:\Projects\Boards\VB\COMPULAB\CM-188_FC-MPCI_Rev1.0\PCB\keyin.icdb\cdbback\2017-09-14 15.10.59-8068-d.zip]
[2017.09.14 15:10:59] [0038m.52s.330ms] [1] iCDB Server PI: Stats: Total iCDB execution time: [85224] ticks
[2017.09.14 15:10:59] [0038m.52s.330ms] [1] iCDB Server PI: Stats: Total iCDB execution time: [27558] microseconds
[2017.09.14 15:10:59] [0038m.52s.330ms] [1] iCDB Server PI: Stats: Total iCDB commands count: [704]
[2017.09.14 15:10:59] [0038m.52s.330ms] [1] iCDB Server PI: Stats: Average iCDB speed:        [25545] commands per second
[2017.09.14 15:10:59] [0038m.52s.466ms] [0] S: STOP
